**** dT 0.000 * top TEST ../../vmod/tests/std_c00002.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=fc00:a462:8e9e:946a:5df3:31c6:d23:18dc **** top extmacro def bad_backend=[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:35205 **** top extmacro def listen_addr=[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build **** top extmacro def topsrc=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/.. **** top macro def testdir=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/../../vmod/tests **** top macro def tmpdir=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0 **** top macro def vtcid=vtc.552991.284b4ab0 ** top === varnishtest "std.ip(), .port() and .set_ip_tos() don't work ... * top VTEST std.ip(), .port() and .set_ip_tos() don't work for UDS addresses ** top === varnish v1 -arg "-a ${tmpdir}/v1.sock -b '${bad_backend}'" -... **** dT 0.012 ** v1 Launch *** v1 CMD: cd ${pwd} && exec varnishd -d -n /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 33507' -P /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1/varnishd.pid -p vmod_path=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -a /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock -b '[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:35205' *** v1 CMD: cd /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod && exec varnishd -d -n /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 33507' -P /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1/varnishd.pid -p vmod_path=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -a /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock -b '[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:35205' **** dT 0.013 *** v1 PID: 553003 **** v1 macro def v1_pid=553003 **** v1 macro def v1_name=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1 **** dT 0.547 *** v1 debug|Warnings: *** v1 debug|Message from VCC-compiler: *** v1 debug|Security: Privilege proc_setid missing, will not change uid/gid *** v1 debug|Message from C-compiler: *** v1 debug|Security: Privilege proc_setid missing, will not change uid/gid *** v1 debug|Message from dlopen: *** v1 debug|Security: Privilege proc_setid missing, will not change uid/gid *** v1 debug| **** dT 0.558 *** v1 debug|Warning: mlock() of VSM failed: Not owner (1 *** v1 debug|) **** dT 0.562 *** v1 debug|Debug: Version: varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 *** v1 debug| *** v1 debug|Debug: Platform: -jsolaris,-sdefault,-sdefault,-hcritbit *** v1 debug|200 290 *** v1 debug|----------------------------- *** v1 debug|Varnish Cache CLI 1.0 *** v1 debug|----------------------------- *** v1 debug|-jsolaris,-sdefault,-sdefault,-hcritbit *** v1 debug|varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 *** v1 debug| *** v1 debug|Type 'help' for command list. *** v1 debug|Type 'quit' to close CLI session. *** v1 debug|Type 'start' to launch worker process. *** v1 debug| **** dT 0.660 **** v1 CLIPOLL 1 0x1 0x0 0x0 *** v1 CLI connection fd = 4 *** v1 CLI RX 107 **** v1 CLI RX|tahwjxqyhszognfftouhdzqiquteirbv **** v1 CLI RX| **** v1 CLI RX|Authentication required. **** dT 0.661 **** v1 CLI TX|auth ebff4f95892caf2cf6cd00b1a39a73c8a1fbf346341bdc77adc57de8686c42f3 *** v1 CLI RX 200 **** v1 CLI RX|----------------------------- **** v1 CLI RX|Varnish Cache CLI 1.0 **** v1 CLI RX|----------------------------- **** v1 CLI RX|-jsolaris,-sdefault,-sdefault,-hcritbit **** v1 CLI RX|varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 **** v1 CLI RX| **** v1 CLI RX|Type 'help' for command list. **** v1 CLI RX|Type 'quit' to close CLI session. **** v1 CLI RX|Type 'start' to launch worker process. **** dT 0.662 ** v1 Start **** v1 CLI TX|start **** dT 0.674 *** v1 debug|Debug: *** v1 debug| Child (553056) Started **** dT 0.705 *** v1 debug|Child launched OK **** dT 0.717 *** v1 CLI RX 200 *** v1 wait-running **** v1 CLI TX|status *** v1 debug|Info: Child (553056) said Child starts *** v1 debug|Info: Child (553056) said Security: Privilege proc_setid missing, will not change uid/gid *** v1 CLI RX 200 **** v1 CLI RX|Child in state running **** v1 CLI TX|debug.listen_address *** v1 CLI RX 200 **** v1 CLI RX|a0 /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock - **** v1 CLI TX|debug.xid 1000 *** v1 CLI RX 200 **** v1 CLI RX|XID is 1000 chunk 1 **** v1 CLI TX|debug.listen_address *** v1 CLI RX 200 **** v1 CLI RX|a0 /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock - ** v1 Listen on 0.0.0.0 0 **** v1 macro def v1_addr=0.0.0.0 **** v1 macro def v1_port=0 **** v1 macro def v1_sock=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock **** v1 macro def v1_a0_addr=0.0.0.0 **** v1 macro def v1_a0_port=0 **** v1 macro def v1_a0_sock=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock **** dT 0.762 **** v1 vsl| 0 CLI - Rd vcl.load "boot" vcl_boot.1761556068.063473/vgc.so 1auto **** v1 vsl| 0 CLI - Wr 200 52 Loaded "vcl_boot.1761556068.063473/vgc.so" as "boot" **** v1 vsl| 0 CLI - Rd vcl.use "boot" **** v1 vsl| 0 CLI - Wr 200 0 **** v1 vsl| 0 CLI - Rd start **** v1 vsl| 0 Debug - sockopt: Setting SO_LINGER for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock **** v1 vsl| 0 Debug - sockopt: Setting SO_KEEPALIVE for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock **** v1 vsl| 0 Debug - sockopt: Setting SO_SNDTIMEO for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock **** v1 vsl| 0 Debug - sockopt: Setting SO_RCVTIMEO for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock **** v1 vsl| 0 CLI - Wr 200 0 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 79 a0 /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock - **** v1 vsl| 0 CLI - Rd debug.xid 1000 **** v1 vsl| 0 CLI - Wr 200 19 XID is 1000 chunk 1 **** v1 vsl| 0 CLI - Rd debug.listen_address **** v1 vsl| 0 CLI - Wr 200 79 a0 /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock - **** dT 0.817 ** top === varnish v2 -arg "-a ${tmpdir}/v2.sock" -vcl { **** dT 0.828 ** v2 Launch *** v2 CMD: cd ${pwd} && exec varnishd -d -n /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 46716' -P /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2/varnishd.pid -p vmod_path=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -a /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock *** v2 CMD: cd /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod && exec varnishd -d -n /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 46716' -P /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2/varnishd.pid -p vmod_path=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -a /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock *** v2 PID: 553069 **** v2 macro def v2_pid=553069 **** v2 macro def v2_name=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2 **** dT 0.864 *** v2 debug|Warning: mlock() of VSM failed: Not owner (1) **** dT 0.867 *** v2 debug|Debug: Version: varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 *** v2 debug|Debug: Platform: -jsolaris,-sdefault,-sdefault,-hcritbit *** v2 debug|200 290 *** v2 debug|----------------------------- *** v2 debug|Varnish Cache CLI 1.0 *** v2 debug|----------------------------- *** v2 debug|-jsolaris,-sdefault,-sdefault,-hcritbit *** v2 debug|varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 *** v2 debug| *** v2 debug|Type 'help' for command list. *** v2 debug|Type 'quit' to close CLI session. *** v2 debug|Type 'start' to launch worker process. *** v2 debug| **** dT 0.965 **** v2 CLIPOLL 1 0x1 0x0 0x0 *** v2 CLI connection fd = 15 **** dT 0.966 *** v2 CLI RX 107 **** v2 CLI RX|mjabhalaxmfuwrkhqpkphyoetaqtfxan **** v2 CLI RX| **** v2 CLI RX|Authentication required. **** v2 CLI TX|auth e2d6a2d657284fc85425c9581624314d9a14244c3a9b1d752591f2cde69ecebf **** dT 0.967 *** v2 CLI RX 200 **** v2 CLI RX|----------------------------- **** v2 CLI RX|Varnish Cache CLI 1.0 **** v2 CLI RX|----------------------------- **** v2 CLI RX|-jsolaris,-sdefault,-sdefault,-hcritbit **** v2 CLI RX|varnish-trunk revision bb1259f1b25af80444df3da8feafd85251405f84 **** v2 CLI RX| **** v2 CLI RX|Type 'help' for command list. **** v2 CLI RX|Type 'quit' to close CLI session. **** v2 CLI RX|Type 'start' to launch worker process. **** v2 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v2 CLI TX|vcl 4.1; **** v2 CLI TX| **** v2 CLI TX|\timport std; **** v2 CLI TX|\tbackend default None; **** v2 CLI TX| **** v2 CLI TX|\tsub vcl_recv { **** v2 CLI TX|\t\treturn(synth(200)); **** v2 CLI TX|\t} **** v2 CLI TX| **** v2 CLI TX|\tsub vcl_synth { **** v2 CLI TX|\t\tset resp.http.v1_addr = std.ip("/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v1.sock", client.ip); **** v2 CLI TX|\t} **** v2 CLI TX| **** v2 CLI TX|%XJEIFLH|)Xspa8P **** dT 1.067 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 1.167 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 1.267 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 1.367 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 1.467 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 1.483 *** v2 CLI RX 200 **** v2 CLI RX|Message from VCC-compiler: **** v2 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v2 CLI RX|Message from C-compiler: **** v2 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v2 CLI RX|Message from dlopen: **** v2 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v2 CLI RX|VCL compiled. **** v2 CLI TX|vcl.use vcl1 *** v2 CLI RX 200 **** v2 CLI RX|VCL 'vcl1' now active ** v2 Start **** v2 CLI TX|start **** dT 1.496 *** v2 debug|Debug: *** v2 debug| Child (553122) Started **** dT 1.528 *** v2 debug|Child launched OK **** dT 1.568 **** v2 vsl| 0 CLI - Rd vcl.load "vcl1" vcl_vcl1.1761556068.991948/vgc.so 1auto **** dT 1.578 *** v2 CLI RX 200 *** v2 debug|Info: Child (553122) said Child starts *** v2 debug|Info: *** v2 wait-running **** v2 CLI TX|status *** v2 debug|Child (553122) said Security: Privilege proc_setid missing, will not change uid/gid *** v2 CLI RX 200 **** v2 CLI RX|Child in state running **** v2 CLI TX|debug.listen_address *** v2 CLI RX 200 **** v2 CLI RX|a0 /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock - **** v2 CLI TX|debug.xid 1000 *** v2 CLI RX 200 **** v2 CLI RX|XID is 1000 chunk 1 **** v2 CLI TX|debug.listen_address *** v2 CLI RX 200 **** v2 CLI RX|a0 /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock - ** v2 Listen on 0.0.0.0 0 **** v2 macro def v2_addr=0.0.0.0 **** v2 macro def v2_port=0 **** v2 macro def v2_sock=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 macro def v2_a0_addr=0.0.0.0 **** v2 macro def v2_a0_port=0 **** v2 macro def v2_a0_sock=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock ** top === client c1 -connect "${v2_sock}" { ** c1 Starting client ** c1 Waiting for client ** c1 Started on /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock (1 iterations) *** c1 Connect to /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock *** c1 connected fd 25 to /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock ** c1 === txreq **** dT 1.579 **** c1 txreq|GET / HTTP/1.1\r **** c1 txreq|Host: fc00:a462:8e9e:946a:5df3:31c6:d23:18dc\r **** c1 txreq|User-Agent: c1\r **** c1 txreq|\r ** c1 === rxresp **** dT 1.668 **** v2 vsl| 0 CLI - Wr 200 52 Loaded "vcl_vcl1.1761556068.991948/vgc.so" as "vcl1" **** v2 vsl| 0 CLI - Rd vcl.use "vcl1" **** v2 vsl| 0 CLI - Wr 200 0 **** v2 vsl| 0 CLI - Rd start **** v2 vsl| 0 Debug - sockopt: Setting SO_LINGER for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 vsl| 0 Debug - sockopt: Setting SO_KEEPALIVE for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 vsl| 0 Debug - sockopt: Setting SO_SNDTIMEO for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 vsl| 0 Debug - sockopt: Setting SO_RCVTIMEO for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 vsl| 0 CLI - Wr 200 0 **** v2 vsl| 0 CLI - Rd debug.listen_address **** v2 vsl| 0 CLI - Wr 200 79 a0 /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock - **** v2 vsl| 0 CLI - Rd debug.xid 1000 **** v2 vsl| 0 CLI - Wr 200 19 XID is 1000 chunk 1 **** v2 vsl| 0 CLI - Rd debug.listen_address **** v2 vsl| 0 CLI - Wr 200 79 a0 /tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock - **** v2 vsl| 1000 Begin c sess 0 HTTP/1 **** v2 vsl| 1000 SessOpen c 0.0.0.0 0 a0 0.0.0.0 0 1761556069.639909 16 **** v2 vsl| 1000 Debug c sockopt: Test confirmed SO_KEEPALIVE non heredity for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 vsl| 1000 Debug c sockopt: Test confirmed SO_SNDTIMEO non heredity for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 vsl| 1000 Debug c sockopt: Test confirmed SO_RCVTIMEO non heredity for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 vsl| 1000 Debug c sockopt: SO_LINGER may be inherited for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 vsl| 1000 Debug c sockopt: Setting SO_KEEPALIVE for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 vsl| 1000 Debug c sockopt: Setting SO_SNDTIMEO for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 vsl| 1000 Debug c sockopt: Setting SO_RCVTIMEO for a0=/tmp/vtest.gcc4.7.3_m32_IPv6_uffix/_vtest_tmp/vtc.552991.284b4ab0/v2.sock **** v2 vsl| 1000 Link c req 1001 rxreq **** dT 3.764 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556071 1.0 **** dT 4.569 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556072 1.0 **** dT 6.766 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556074 1.0 **** dT 7.571 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556075 1.0 **** dT 9.768 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556077 1.0 **** dT 10.573 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556078 1.0 **** dT 12.770 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556080 1.0 **** dT 13.575 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556081 1.0 **** dT 15.772 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556083 1.0 **** dT 16.577 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556084 1.0 **** dT 18.774 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556086 1.0 **** dT 19.578 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556087 1.0 **** dT 21.776 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556089 1.0 **** dT 22.580 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556090 1.0 **** dT 24.778 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556092 1.0 **** dT 25.582 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556093 1.0 **** dT 27.779 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556095 1.0 **** dT 28.584 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556096 1.0 **** dT 30.781 **** v1 vsl| 0 CLI - Rd ping **** v1 vsl| 0 CLI - Wr 200 19 PONG 1761556098 1.0 **** dT 31.579 ---- c1 HTTP rx timeout (fd:25 30.000s) * top Aborting execution, test failed * top RESETTING after ../../vmod/tests/std_c00002.vtc ** v1 Wait **** v1 CLI TX|panic.show *** v1 CLI RX 300 **** v1 CLI RX|Child has not panicked or panic has been cleared *** v1 debug|Info: manager stopping child *** v1 debug|Debug: *** v1 debug| Stopping Child **** dT 31.582 **** v1 vsl| 0 CLI - EOF on CLI connection, worker stops **** dT 31.586 **** v2 vsl| 0 CLI - Rd ping **** v2 vsl| 0 CLI - Wr 200 19 PONG 1761556099 1.0 **** dT 31.679 *** v1 debug|Info: Child (553056) said Child dies *** v1 debug|Info: Child (553056) ended *** v1 debug|Debug: *** v1 debug|Child cleanup complete *** v1 debug|Info: *** v1 debug|manager dies **** dT 31.680 **** v1 STDOUT EOF **** dT 31.682 ** v1 WAIT4 pid=553003 status=0x0000 (user 0.435000 sys 0.146000) ** v2 Wait **** v2 CLI TX|panic.show *** v2 CLI RX 300 **** v2 CLI RX|Child has not panicked or panic has been cleared *** v2 debug|Info: manager stopping child *** v2 debug|Debug: Stopping Child **** dT 31.686 **** v2 vsl| 0 CLI - EOF on CLI connection, worker stops **** dT 32.683 *** v2 debug|Info: Child (553122) said shutdown waiting for 1 reference on vcl1 **** dT 33.683 *** v2 debug|Info: Child (553122) said shutdown waiting for 1 reference on vcl1 **** dT 34.684 *** v2 debug|Info: Child (553122) said shutdown waiting for 1 reference on vcl1 **** dT 35.684 *** v2 debug|Info: Child (553122) said shutdown waiting for 1 reference on vcl1 **** dT 36.684 *** v2 debug|Info: Child (553122) said shutdown waiting for 1 reference on vcl1 **** dT 37.684 *** v2 debug|Info: Child (553122) said shutdown waiting for 1 reference on vcl1 **** dT 38.685 *** v2 debug|Info: Child (553122) said shutdown waiting for 1 reference on vcl1 **** dT 39.585 *** v2 debug|Info: Child (553122) said shutdown waiting for 1 reference on vcl1 **** dT 40.585 *** v2 debug|Info: Child (553122) said shutdown waiting for 1 reference on vcl1 **** dT 41.586 *** v2 debug|Info: Child (553122) said shutdown waiting for 1 reference on vcl1 **** dT 41.692 **** v2 vsl| 1001 Begin c req 1000 rxreq **** v2 vsl| 1001 Timestamp c Start: 1761556069.640109 0.000000 0.000000 **** v2 vsl| 1001 Timestamp c Req: 1761556069.640109 0.000000 0.000000 **** v2 vsl| 1001 VCL_use c vcl1 **** v2 vsl| 1001 ReqStart c 0.0.0.0 0 a0 **** v2 vsl| 1001 ReqMethod c GET **** v2 vsl| 1001 ReqURL c / **** v2 vsl| 1001 ReqProtocol c HTTP/1.1 **** v2 vsl| 1001 ReqHeader c Host: fc00:a462:8e9e:946a:5df3:31c6:d23:18dc **** v2 vsl| 1001 ReqHeader c User-Agent: c1 **** v2 vsl| 1001 ReqHeader c X-Forwarded-For: 0.0.0.0 **** v2 vsl| 1001 ReqHeader c Via: 1.1 v2 (Varnish/trunk) **** v2 vsl| 1001 VCL_call c RECV **** v2 vsl| 1001 VCL_return c synth **** v2 vsl| 1001 VCL_call c HASH **** v2 vsl| 1001 VCL_return c lookup **** v2 vsl| 1001 RespProtocol c HTTP/1.1 **** v2 vsl| 1001 RespStatus c 200 **** v2 vsl| 1001 RespReason c OK **** v2 vsl| 1001 RespHeader c Date: Mon, 27 Oct 2025 09:07:49 GMT **** v2 vsl| 1001 RespHeader c Server: Varnish **** v2 vsl| 1001 RespHeader c X-Varnish: 1001 **** v2 vsl| 1001 VCL_call c SYNTH **** v2 vsl| 1001 RespHeader c v1_addr: 0.0.0.0 **** v2 vsl| 1001 RespHeader c Content-Type: text/html; charset=utf-8 **** v2 vsl| 1001 RespHeader c Retry-After: 5 **** v2 vsl| 1001 VCL_return c deliver **** v2 vsl| 1001 Timestamp c Process: 1761556109.695155 40.055045 40.055045 **** v2 vsl| 1001 RespHeader c Content-Length: 227 **** v2 vsl| 1001 Storage c umem Transient **** v2 vsl| 1001 Filters c **** v2 vsl| 1001 RespHeader c Connection: keep-alive **** v2 vsl| 1001 Timestamp c Resp: 1761556109.695569 40.055459 0.000414 **** v2 vsl| 1001 ReqAcct c 80 0 80 209 227 436 **** v2 vsl| 1001 End c **** dT 41.786 *** v2 debug|Info: Child (553122) said Child dies **** dT 41.886 *** v2 debug|Info: Child (553122) ended *** v2 debug|Debug: Child cleanup complete *** v2 debug| *** v2 debug|Info: manager dies *** v2 debug| **** v2 STDOUT EOF **** dT 41.892 ** v2 WAIT4 pid=553069 status=0x0000 (user 0.436000 sys 0.192000) * top TEST ../../vmod/tests/std_c00002.vtc FAILED # top TEST ../../vmod/tests/std_c00002.vtc FAILED (41.895) exit=2